[INFO] cloning repository https://github.com/software-factory-vibe-city/chip8
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/software-factory-vibe-city/chip8" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsoftware-factory-vibe-city%2Fchip8", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsoftware-factory-vibe-city%2Fchip8'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 6c724a1c92a85b95a8058cab79bcfba067cf99be
[INFO] testing software-factory-vibe-city/chip8 against master#1f7f8ea0721a3b1eb73e6c6d25cccb371434b320 for pr-154065-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsoftware-factory-vibe-city%2Fchip8" "/workspace/builds/worker-6-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-6-tc1/source/.cargo/config.toml
[INFO] started tweaking git repo https://github.com/software-factory-vibe-city/chip8
[INFO] finished tweaking git repo https://github.com/software-factory-vibe-city/chip8
[INFO] tweaked toml for git repo https://github.com/software-factory-vibe-city/chip8 written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/software-factory-vibe-city/chip8 on toolchain 1f7f8ea0721a3b1eb73e6c6d25cccb371434b320
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/software-factory-vibe-city/chip8 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 1c2d46ab15e5b57f6a23f309e99741bc2a2ab0bcf0231b4a34f6769fbe30e5c9
[INFO] running `Command { std: "docker" "start" "-a" "1c2d46ab15e5b57f6a23f309e99741bc2a2ab0bcf0231b4a34f6769fbe30e5c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1c2d46ab15e5b57f6a23f309e99741bc2a2ab0bcf0231b4a34f6769fbe30e5c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1c2d46ab15e5b57f6a23f309e99741bc2a2ab0bcf0231b4a34f6769fbe30e5c9", kill_on_drop: false }`
[INFO] [stdout] 1c2d46ab15e5b57f6a23f309e99741bc2a2ab0bcf0231b4a34f6769fbe30e5c9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8bf374f799e32f667553391bdac619902cc647105eaf4412f6ae57a28b662bb4
[INFO] running `Command { std: "docker" "start" "-a" "8bf374f799e32f667553391bdac619902cc647105eaf4412f6ae57a28b662bb4", kill_on_drop: false }`
[INFO] [stderr]    Compiling chip8 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.76s
[INFO] running `Command { std: "docker" "inspect" "8bf374f799e32f667553391bdac619902cc647105eaf4412f6ae57a28b662bb4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8bf374f799e32f667553391bdac619902cc647105eaf4412f6ae57a28b662bb4", kill_on_drop: false }`
[INFO] [stdout] 8bf374f799e32f667553391bdac619902cc647105eaf4412f6ae57a28b662bb4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1edcba46e75c76eddb5e8f9ef0465e831bd21b8fa2450945398dabcde6b7c234
[INFO] running `Command { std: "docker" "start" "-a" "1edcba46e75c76eddb5e8f9ef0465e831bd21b8fa2450945398dabcde6b7c234", kill_on_drop: false }`
[INFO] [stderr]    Compiling chip8 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.89s
[INFO] running `Command { std: "docker" "inspect" "1edcba46e75c76eddb5e8f9ef0465e831bd21b8fa2450945398dabcde6b7c234", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1edcba46e75c76eddb5e8f9ef0465e831bd21b8fa2450945398dabcde6b7c234", kill_on_drop: false }`
[INFO] [stdout] 1edcba46e75c76eddb5e8f9ef0465e831bd21b8fa2450945398dabcde6b7c234
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 565640697f9fcc27b5ae255718af4b2553513f6fa826c3eeeb78083e785214ac
[INFO] running `Command { std: "docker" "start" "-a" "565640697f9fcc27b5ae255718af4b2553513f6fa826c3eeeb78083e785214ac", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/chip8-97c18236980011dc)
[INFO] [stdout] 
[INFO] [stdout] running 123 tests
[INFO] [stdout] test chip8::tests::test_chip8_initialization ... ok
[INFO] [stdout] test chip8::tests::test_constants ... ok
[INFO] [stdout] test chip8::tests::test_cxnn_produces_varying_values ... ok
[INFO] [stdout] test chip8::tests::test_execute_1nnn_jp_addr ... ok
[INFO] [stdout] test chip8::tests::test_execute_00e0_cls ... ok
[INFO] [stdout] test chip8::tests::test_execute_2nnn_call_and_00ee_ret ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy0_ld_vx_vy ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy2_and_vx_vy ... ok
[INFO] [stdout] test chip8::tests::test_execute_3xkk_se_vx_byte_equal ... ok
[INFO] [stdout] test chip8::tests::test_execute_5xy0_se_vx_vy_equal ... ok
[INFO] [stdout] test chip8::tests::test_execute_6xkk_ld_vx_byte ... ok
[INFO] [stdout] test chip8::tests::test_execute_7xkk_add_vx_byte ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy1_or_vx_vy ... ok
[INFO] [stdout] test chip8::tests::test_execute_3xkk_se_vx_byte_not_equal ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy3_xor_vx_vy ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy4_add_vx_vy_no_carry ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy4_add_vx_vy_with_carry ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy6_shift_ignores_vy ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy6_shr_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy5_sub_vx_vy_with_borrow ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy7_subn_vx_vy ... ok
[INFO] [stdout] test chip8::tests::test_execute_annn_ld_i_addr ... ok
[INFO] [stdout] test chip8::tests::test_cxnn_mask_zero_always_zero ... ok
[INFO] [stdout] test chip8::tests::test_execute_bnnn_jp_v0_addr ... ok
[INFO] [stdout] test chip8::tests::test_execute_cxkk_rnd_vx_byte ... ok
[INFO] [stdout] test chip8::tests::test_execute_dxyn_collision ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xye_shift_ignores_vy ... ok
[INFO] [stdout] test chip8::tests::test_execute_dxyn_draw_basic ... ok
[INFO] [stdout] test chip8::tests::test_execute_9xy0_sne_vx_vy_not_equal ... ok
[INFO] [stdout] test chip8::tests::test_execute_dxyn_draw_xor ... ok
[INFO] [stdout] test chip8::tests::test_execute_call_pushes_pc_plus_2 ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xy5_sub_vx_vy_no_borrow ... ok
[INFO] [stdout] test chip8::tests::test_execute_8xye_shl_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_4xkk_sne_vx_byte_not_equal ... ok
[INFO] [stdout] test chip8::tests::test_execute_dxyn_no_collision ... ok
[INFO] [stdout] test chip8::tests::test_execute_dxyn_wrap_both ... ok
[INFO] [stdout] test chip8::tests::test_execute_dxyn_wrap_x ... ok
[INFO] [stdout] test chip8::tests::test_execute_dxyn_wrap_y ... ok
[INFO] [stdout] test chip8::tests::test_execute_ex9e_skp_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx0a_ld_vx_k_pressed ... ok
[INFO] [stdout] test chip8::tests::test_execute_exa1_sknp_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx0a_ld_vx_k_wait ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx15_ld_dt_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx18_ld_st_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx1e_add_i_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx07_ld_vx_dt ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx55_i_preserved ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx29_ld_f_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx65_i_preserved ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx33_ld_b_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_nested_call_ret ... ok
[INFO] [stdout] test chip8::tests::test_execute_stack_overflow ... ok
[INFO] [stdout] test chip8::tests::test_execute_stack_underflow ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx65_ld_vx_i ... ok
[INFO] [stdout] test chip8::tests::test_execute_unknown_opcode ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx55_ld_i_vx ... ok
[INFO] [stdout] test chip8::tests::test_execute_fx0a_key_state_persists_until_consumed ... ok
[INFO] [stdout] test chip8::tests::test_shl_vf_as_vx ... ok
[INFO] [stdout] test chip8::tests::test_tick_timers_both_timers_decrement ... ok
[INFO] [stdout] test chip8::tests::test_shr_vf_as_vx ... ok
[INFO] [stdout] test chip8::tests::test_tick_timers_decrements_delay_timer ... ok
[INFO] [stdout] test chip8::tests::test_tick_timers_delay_timer_does_not_go_below_zero ... ok
[INFO] [stdout] test chip8::tests::test_tick_timers_does_not_affect_other_state ... ok
[INFO] [stdout] test chip8::tests::test_tick_timers_multiple_ticks ... ok
[INFO] [stdout] test chip8::tests::test_tick_timers_sound_timer_does_not_go_below_zero ... ok
[INFO] [stdout] test display::tests::test_buffer_pointer ... ok
[INFO] [stdout] test display::tests::test_dirty_flag ... ok
[INFO] [stdout] test display::tests::test_display_size ... ok
[INFO] [stdout] test display::tests::test_pixel_collision ... ok
[INFO] [stdout] test chip8::tests::test_tick_timers_decrements_sound_timer ... ok
[INFO] [stdout] test display::tests::test_clear ... ok
[INFO] [stdout] test display::tests::test_pixel_wrap_x ... ok
[INFO] [stdout] test display::tests::test_pixel_wrap_y ... ok
[INFO] [stdout] test font::tests::test_font_addresses ... ok
[INFO] [stdout] test font::tests::test_font_data_size ... ok
[INFO] [stdout] test font::tests::test_font_sprite_count ... ok
[INFO] [stdout] test font::tests::test_font_sprite_size ... ok
[INFO] [stdout] test display::tests::test_coordinates_at_edges ... ok
[INFO] [stdout] test keyboard::tests::test_any_key_pressed ... ok
[INFO] [stdout] test chip8::tests::test_reset ... ok
[INFO] [stdout] test display::tests::test_clear_resets_dirty ... ok
[INFO] [stdout] test keyboard::tests::test_consume_pressed_key ... ok
[INFO] [stdout] test keyboard::tests::test_consume_pressed_key_first_one ... ok
[INFO] [stdout] test keyboard::tests::test_get_pressed_key ... ok
[INFO] [stdout] test keyboard::tests::test_get_pressed_key_first_one ... ok
[INFO] [stdout] test keyboard::tests::test_key_down ... ok
[INFO] [stdout] test keyboard::tests::test_key_toggle ... ok
[INFO] [stdout] test keyboard::tests::test_key_up ... ok
[INFO] [stdout] test keyboard::tests::test_reset ... ok
[INFO] [stdout] test memory::tests::test_font_loaded_for_digit_1 ... ok
[INFO] [stdout] test memory::tests::test_font_loaded_at_start ... ok
[INFO] [stdout] test memory::tests::test_font_loaded_for_digit_f ... ok
[INFO] [stdout] test memory::tests::test_memory_defaults_to_zero_beyond_fonts ... ok
[INFO] [stdout] test memory::tests::test_memory_read_write ... ok
[INFO] [stdout] test display::tests::test_set_pixel ... ok
[INFO] [stdout] test memory::tests::test_memory_size ... ok
[INFO] [stdout] test keyboard::tests::test_keyboard_initial_state ... ok
[INFO] [stdout] test registers::tests::test_registers_initial_values ... ok
[INFO] [stdout] test memory::tests::test_program_slice ... ok
[INFO] [stdout] test registers::tests::test_set_v ... ok
[INFO] [stdout] test registers::tests::test_sp_decrement ... ok
[INFO] [stdout] test registers::tests::test_sp_overflow ... ok
[INFO] [stdout] test registers::tests::test_sp_underflow ... ok
[INFO] [stdout] test registers::tests::test_flag_operations ... ok
[INFO] [stdout] test registers::tests::test_sp_increment ... ok
[INFO] [stdout] test stack::tests::test_lifo_order ... ok
[INFO] [stdout] test stack::tests::test_peek ... ok
[INFO] [stdout] test stack::tests::test_pop_empty_stack ... ok
[INFO] [stdout] test stack::tests::test_stack_initial_state ... ok
[INFO] [stdout] test stack::tests::test_push_pop ... ok
[INFO] [stdout] test stack::tests::test_stack_overflow ... ok
[INFO] [stdout] test wasm::tests::test_display_ptr_returns_correct_offset ... ok
[INFO] [stdout] test stack::tests::test_stack_underflow ... ok
[INFO] [stdout] test wasm::tests::test_pointer_relationship ... ok
[INFO] [stdout] test wasm::tests::test_rom_buffer_ptr_returns_correct_offset ... ok
[INFO] [stdout] test registers::tests::test_get_v ... ok
[INFO] [stdout] test stack::tests::test_clear ... ok
[INFO] [stdout] test keyboard::tests::test_is_key_pressed_out_of_bounds - should panic ... ok
[INFO] [stdout] test keyboard::tests::test_key_down_out_of_bounds - should panic ... ok
[INFO] [stdout] test memory::tests::test_memory_read_out_of_bounds - should panic ... ok
[INFO] [stdout] test keyboard::tests::test_key_up_out_of_bounds - should panic ... ok
[INFO] [stdout] test registers::tests::test_get_v_out_of_bounds - should panic ... ok
[INFO] [stdout] test memory::tests::test_memory_write_out_of_bounds - should panic ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/z_chip8_runner-3d5aa47b5c17a8e1)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 123 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests chip8
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "565640697f9fcc27b5ae255718af4b2553513f6fa826c3eeeb78083e785214ac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "565640697f9fcc27b5ae255718af4b2553513f6fa826c3eeeb78083e785214ac", kill_on_drop: false }`
[INFO] [stdout] 565640697f9fcc27b5ae255718af4b2553513f6fa826c3eeeb78083e785214ac
